[INFO] cloning repository https://github.com/Bazooh/RustLox
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/Bazooh/RustLox" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f0dfc0cf917f5997c94a6cb5556b8b9c354da810
[INFO] testing Bazooh/RustLox against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBazooh%2FRustLox" "/workspace/builds/worker-1-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/Bazooh/RustLox
[INFO] finished tweaking git repo https://github.com/Bazooh/RustLox
[INFO] tweaked toml for git repo https://github.com/Bazooh/RustLox written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/Bazooh/RustLox on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/Bazooh/RustLox already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 83a6a6edbd3fa8ef5add2171345dcfad8152db4bb8d3cff399f7206f3c631b94
[INFO] running `Command { std: "docker" "start" "-a" "83a6a6edbd3fa8ef5add2171345dcfad8152db4bb8d3cff399f7206f3c631b94", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "83a6a6edbd3fa8ef5add2171345dcfad8152db4bb8d3cff399f7206f3c631b94", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "83a6a6edbd3fa8ef5add2171345dcfad8152db4bb8d3cff399f7206f3c631b94", kill_on_drop: false }`
[INFO] [stdout] 83a6a6edbd3fa8ef5add2171345dcfad8152db4bb8d3cff399f7206f3c631b94
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 669c8dc1d714cb0110bc27eff7f2bc6b404f99825ecb9165e6169efc82fdaa66
[INFO] running `Command { std: "docker" "start" "-a" "669c8dc1d714cb0110bc27eff7f2bc6b404f99825ecb9165e6169efc82fdaa66", kill_on_drop: false }`
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling relative-path v1.9.3
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling toml_parser v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling futures-timer v3.0.3
[INFO] [stderr]    Compiling strum v0.27.2
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling syn v2.0.112
[INFO] [stderr]    Compiling toml_edit v0.23.10+spec-1.0.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling proc-macro-crate v3.4.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling strum_macros v0.27.2
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `parse_expect` is never used
[INFO] [stdout]  --> src/test.rs:6:4
[INFO] [stdout]   |
[INFO] [stdout] 6 | fn parse_expect(content: &str) -> (String, String) {
[INFO] [stdout]   |    ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_file` is never used
[INFO] [stdout]   --> src/test.rs:35:4
[INFO] [stdout]    |
[INFO] [stdout] 35 | fn test_file(path: &str, func: fn(&str) -> (String, String)) {
[INFO] [stdout]    |    ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_interpret_file` is never used
[INFO] [stdout]   --> src/test.rs:91:4
[INFO] [stdout]    |
[INFO] [stdout] 91 | fn test_interpret_file(path: &str) {
[INFO] [stdout]    |    ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `test_tokenize_file` is never used
[INFO] [stdout]    --> src/test.rs:108:4
[INFO] [stdout]     |
[INFO] [stdout] 108 | fn test_tokenize_file(path: &str) {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 18.02s
[INFO] running `Command { std: "docker" "inspect" "669c8dc1d714cb0110bc27eff7f2bc6b404f99825ecb9165e6169efc82fdaa66", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "669c8dc1d714cb0110bc27eff7f2bc6b404f99825ecb9165e6169efc82fdaa66", kill_on_drop: false }`
[INFO] [stdout] 669c8dc1d714cb0110bc27eff7f2bc6b404f99825ecb9165e6169efc82fdaa66
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a64d64d58c2e7e708475de8a9561e4762e6c98f53658c5d46e4d8668aee5f9ce
[INFO] running `Command { std: "docker" "start" "-a" "a64d64d58c2e7e708475de8a9561e4762e6c98f53658c5d46e4d8668aee5f9ce", kill_on_drop: false }`
[INFO] [stderr]    Compiling RustLox v0.1.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: method `repr` is never used
[INFO] [stdout]   --> src/instruction.rs:96:12
[INFO] [stdout]    |
[INFO] [stdout] 95 | impl Instruction {
[INFO] [stdout]    | ---------------- method in this implementation
[INFO] [stdout] 96 |     pub fn repr(&self) -> String {
[INFO] [stdout]    |            ^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.84s
[INFO] running `Command { std: "docker" "inspect" "a64d64d58c2e7e708475de8a9561e4762e6c98f53658c5d46e4d8668aee5f9ce", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a64d64d58c2e7e708475de8a9561e4762e6c98f53658c5d46e4d8668aee5f9ce", kill_on_drop: false }`
[INFO] [stdout] a64d64d58c2e7e708475de8a9561e4762e6c98f53658c5d46e4d8668aee5f9ce
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 45981dfca83783bf80a5e8a27bd6fa89737477c76b75d5d94750aaf0924533d4
[INFO] running `Command { std: "docker" "start" "-a" "45981dfca83783bf80a5e8a27bd6fa89737477c76b75d5d94750aaf0924533d4", kill_on_drop: false }`
[INFO] [stderr] warning: method `repr` is never used
[INFO] [stderr]   --> src/instruction.rs:96:12
[INFO] [stderr]    |
[INFO] [stderr] 95 | impl Instruction {
[INFO] [stderr]    | ---------------- method in this implementation
[INFO] [stderr] 96 |     pub fn repr(&self) -> String {
[INFO] [stderr]    |            ^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `RustLox` (bin "RustLox" test) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/RustLox-5686169b899852d7)
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test test::test_module::case_07_comments ... ok
[INFO] [stdout] test test::test_module::case_09_expressions ... ok
[INFO] [stdout] test test::test_module::case_17_nil ... ok
[INFO] [stdout] test test::test_empty_file ... ok
[INFO] [stdout] test test::test_module::case_08_constructor ... FAILED
[INFO] [stdout] test test::test_module::case_03_bool ... ok
[INFO] [stdout] test test::test_module::case_02_block ... ok
[INFO] [stdout] test test::test_module::case_21_regression ... ok
[INFO] [stdout] test test::test_module::case_05_class ... FAILED
[INFO] [stdout] test test::test_module::case_18_number ... ok
[INFO] [stdout] test test::test_module::case_20_print ... ok
[INFO] [stdout] test test::test_module::case_04_call ... ok
[INFO] [stdout] test test::test_module::case_16_method ... ok
[INFO] [stdout] test test::test_precedence ... ok
[INFO] [stdout] test test::test_module::case_23_string ... ok
[INFO] [stdout] test test::test_module::case_25_this ... FAILED
[INFO] [stdout] test test::test_module::case_15_logical_operator ... ok
[INFO] [stdout] test test::test_unexpected_character ... ok
[INFO] [stdout] test test::test_module::case_24_super ... FAILED
[INFO] [stdout] test test::test_tokenize::case_1 ... ok
[INFO] [stdout] test test::test_module::case_22_return_ ... ok
[INFO] [stdout] test test::test_module::case_27_while_ ... ok
[INFO] [stdout] test test::test_module::case_19_operator ... ok
[INFO] [stdout] test test::test_module::case_01_assignment ... ok
[INFO] [stdout] test test::test_module::case_14_inheritance ... FAILED
[INFO] [stdout] test test::test_module::case_11_for_ ... ok
[INFO] [stdout] test test::test_module::case_13_if_ ... ok
[INFO] [stdout] test test::test_module::case_12_function ... ok
[INFO] [stdout] test test::test_module::case_26_variable ... ok
[INFO] [stdout] test test::test_module::case_06_closure ... ok
[INFO] [stdout] test test::test_module::case_10_field ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_08_constructor stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 22,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         7,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 8,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     Add,
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 4 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: String(5) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 4 } Stack: ["one"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["one", "<fn init>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 1 } Stack: ["one", "<class Foo>"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "one", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: String(1) Stack: ["one"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn init>", "one", "<class Foo>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn init>"]
[INFO] [stdout] Executing byte at 24: GetLocal(0) Stack: ["one", "Foo.init("]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "Foo.init("
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "Foo.init(", "<fn init>", "one"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: String(2) Stack: ["one", "Foo.init(", "one"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "Foo.init("
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "one", "<fn init>", "Foo.init("]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Add Stack: ["one", "Foo.init(", "one", ")"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "Foo.init("
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value ")"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: [")", "Foo.init(", "<class Foo>", "one", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Add Stack: ["one", "Foo.init(", "one)"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "Foo.init("
[INFO] [stdout] GC: checking value "one)"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["one", "Foo.init(", "one)", "<class Foo>", "<fn init>"]
[INFO] [stdout] FREEING: [")"]
[INFO] [stdout] Executing byte at 28: Print Stack: ["one", "Foo.init(one)"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "Foo.init(one)"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["Foo.init(one)", "<fn init>", "one", "<class Foo>"]
[INFO] [stdout] FREEING: ["Foo.init(", "one)"]
[INFO] [stdout] Executing byte at 29: GetCaptured(0) Stack: ["one"]
[INFO] [stdout] GC: checking value "one"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "one", "<fn init>"]
[INFO] [stdout] FREEING: ["Foo.init(one)"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_08_constructor' (25) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5e4942686a2a - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5e4942686a2a - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5e494269dada - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5e494269dada - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5e494268b502 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5e494268b502 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5e49426655ef - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5e49426655ef - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5e494267eaa9 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5e49423a508c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5e49423a508c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5e494267ec62 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5e494267ec62 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5e49426656a8 - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5e494265ce69 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5e49426663bd - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5e494269e31c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5e494269e014 - core[a96a4c5c53f902a3]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5e494269e014 - core[a96a4c5c53f902a3]::option::expect_failed
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5e494235fd30 - <core[a96a4c5c53f902a3]::option::Option<&RustLox[83b7e3fb786d5223]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5e494237effe - <RustLox[83b7e3fb786d5223]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5e49423777fd - <RustLox[83b7e3fb786d5223]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5e4942381e3a - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5e4942384442 - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x5e494238e8aa - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5e494236eb16 - RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5e494236418a - <RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5e4942371c21 - RustLox[83b7e3fb786d5223]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5e49423719aa - RustLox[83b7e3fb786d5223]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5e49423708e6 - RustLox[83b7e3fb786d5223]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5e49423621c2 - RustLox[83b7e3fb786d5223]::test::test_module::case_08_constructor
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5e494235f777 - RustLox[83b7e3fb786d5223]::test::test_module::case_08_constructor::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5e49423647c6 - <RustLox[83b7e3fb786d5223]::test::test_module::case_08_constructor::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5e494239916b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5e494239916b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   37:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   38:     0x5e49423a5b5b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5e49423a5b5b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   43:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   44:     0x5e49423a1274 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   45:     0x5e49423a1274 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   47:     0x5e49423a8762 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5e49423a8762 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   52:     0x5e49423a8762 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5e4942685fdf - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   54:     0x5e4942685fdf - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x794742a89aa4 - <unknown>
[INFO] [stdout]   56:     0x794742b16a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_05_class stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Local(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: GetLocal(0) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Call { arg_count: 0 } Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: DotProperty(0) Stack: ["&<class Foo>", "<Foo instance>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<class Foo>", "<fn returnSelf>", "&<pointer 1>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["&<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<Foo instance>", "<fn returnSelf>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Capture(Local(0)) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn returnSelf>"]
[INFO] [stdout] Executing byte at 11: GetCaptured(2) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["&<pointer 1>", "<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: Return Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "&<pointer 1>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Print Stack: ["&<class Foo>", "<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "&<pointer 1>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Pop(1) Stack: ["&<class Foo>"]
[INFO] [stdout] GC: checking value "&<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>", "&<pointer 1>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: ["<class Foo>", "&<pointer 1>", "<fn returnSelf>"]
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 9,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 1 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: DotProperty(0) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<fn returnSelf>", "<class Foo>", "<Foo instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: Call { arg_count: 0 } Stack: ["<fn returnSelf>"]
[INFO] [stdout] GC: checking value "<fn returnSelf>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Foo instance>", "<fn returnSelf>", "<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: ["<fn returnSelf>", "<Foo instance>"]
[INFO] [stdout] Executing byte at 11: Return Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Print Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn returnSelf>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn returnSelf>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         0.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 25,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 31,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Constant(
[INFO] [stdout]         1.0,
[INFO] [stdout]     ),
[INFO] [stdout]     Add,
[INFO] [stdout]     AssignGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(0.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["0"]
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn print_id>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: GetGlobal { string_index: 4 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn print_id>", "<fn init>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<fn print_id>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "0"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn print_id>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: ["<fn init>", "<A instance>", "<fn print_id>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_05_class' (22) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5e4942686a2a - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5e4942686a2a - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5e494269dada - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5e494269dada - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5e494268b502 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5e494268b502 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5e49426655ef - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5e49426655ef - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5e494267eaa9 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5e49423a508c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5e49423a508c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5e494267ec62 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5e494267ec62 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5e49426656a8 - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5e494265ce69 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5e49426663bd - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5e494269e31c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5e494269e014 - core[a96a4c5c53f902a3]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5e494269e014 - core[a96a4c5c53f902a3]::option::expect_failed
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5e494235fd30 - <core[a96a4c5c53f902a3]::option::Option<&RustLox[83b7e3fb786d5223]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5e494237effe - <RustLox[83b7e3fb786d5223]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5e49423777fd - <RustLox[83b7e3fb786d5223]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5e4942381e3a - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5e4942384442 - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x5e494238e8aa - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5e494236eb16 - RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5e494236418a - <RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5e4942371c21 - RustLox[83b7e3fb786d5223]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5e49423719aa - RustLox[83b7e3fb786d5223]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5e49423708e6 - RustLox[83b7e3fb786d5223]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5e4942361dc2 - RustLox[83b7e3fb786d5223]::test::test_module::case_05_class
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5e494235f477 - RustLox[83b7e3fb786d5223]::test::test_module::case_05_class::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5e49423643c6 - <RustLox[83b7e3fb786d5223]::test::test_module::case_05_class::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5e494239916b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5e494239916b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   37:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   38:     0x5e49423a5b5b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5e49423a5b5b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   43:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   44:     0x5e49423a1274 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   45:     0x5e49423a1274 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   47:     0x5e49423a8762 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5e49423a8762 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   52:     0x5e49423a8762 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5e4942685fdf - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   54:     0x5e4942685fdf - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x794742a89aa4 - <unknown>
[INFO] [stdout]   56:     0x794742b16a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_25_this stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 13,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 26,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             0,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 18,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 1 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: GetGlobal { string_index: 1 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<fn toString>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Call { arg_count: 0 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn getClosure>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: DotProperty(2) Stack: ["<Foo instance>"]
[INFO] [stdout] GC: checking value "<Foo instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn getClosure>", "<Foo instance>", "<fn getClosure>", "<class Foo>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: Call { arg_count: 0 } Stack: ["<fn getClosure>"]
[INFO] [stdout] GC: checking value "<fn getClosure>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<Foo instance>", "<fn getClosure>", "<class Foo>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Callable { address: 18, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<class Foo>", "<fn toString>"]
[INFO] [stdout] FREEING: ["<Foo instance>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] Executing byte at 28: GetLocal(0) Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_25_this' (42) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5e4942686a2a - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5e4942686a2a - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5e494269dada - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5e494269dada - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5e494268b502 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5e494268b502 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5e49426655ef - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5e49426655ef - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5e494267eaa9 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5e49423a508c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5e49423a508c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5e494267ec62 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5e494267ec62 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5e49426656a8 - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5e494265ce69 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5e49426663bd - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5e494269e31c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5e494269e014 - core[a96a4c5c53f902a3]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5e494269e014 - core[a96a4c5c53f902a3]::option::expect_failed
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5e494235fd30 - <core[a96a4c5c53f902a3]::option::Option<&RustLox[83b7e3fb786d5223]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5e494237effe - <RustLox[83b7e3fb786d5223]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5e494237f3c2 - <RustLox[83b7e3fb786d5223]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:136:35
[INFO] [stdout]   24:     0x5e494237f484 - <RustLox[83b7e3fb786d5223]::heap::Heap>::gc::dfs
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:137:17
[INFO] [stdout]   25:     0x5e494237c3ee - <RustLox[83b7e3fb786d5223]::heap::Heap>::gc::<core[a96a4c5c53f902a3]::iter::adapters::chain::Chain<core[a96a4c5c53f902a3]::slice::iter::Iter<RustLox[83b7e3fb786d5223]::value::Value>, std[ec4e911328de40a6]::collections::hash::map::Values<alloc[c960326de6de28e6]::string::String, RustLox[83b7e3fb786d5223]::value::Value>>>
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:146:17
[INFO] [stdout]   26:     0x5e494238415e - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:438:23
[INFO] [stdout]   27:     0x5e494238e8aa - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   28:     0x5e494236eb16 - RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   29:     0x5e494236418a - <RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   30:     0x5e4942371c21 - RustLox[83b7e3fb786d5223]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   31:     0x5e49423719aa - RustLox[83b7e3fb786d5223]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   32:     0x5e49423708e6 - RustLox[83b7e3fb786d5223]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   33:     0x5e4942361d42 - RustLox[83b7e3fb786d5223]::test::test_module::case_25_this
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   34:     0x5e494235f417 - RustLox[83b7e3fb786d5223]::test::test_module::case_25_this::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   35:     0x5e4942364346 - <RustLox[83b7e3fb786d5223]::test::test_module::case_25_this::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5e494239916b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   37:     0x5e494239916b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   38:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   39:     0x5e49423a5b5b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   40:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   41:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   42:     0x5e49423a5b5b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   43:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   44:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   45:     0x5e49423a1274 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   46:     0x5e49423a1274 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   47:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   48:     0x5e49423a8762 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   50:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   51:     0x5e49423a8762 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   53:     0x5e49423a8762 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   54:     0x5e4942685fdf - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   55:     0x5e4942685fdf - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   56:     0x794742a89aa4 - <unknown>
[INFO] [stdout]   57:     0x794742b16a64 - clone
[INFO] [stdout]   58:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_24_super stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 16,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 1,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 29,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 35,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Print,
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Capture(
[INFO] [stdout]         Captured(
[INFO] [stdout]             1,
[INFO] [stdout]         ),
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 21,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 1, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 1 } Stack: ["<class Base>"]
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 4, superclass: Some(Global(1)) } Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Base>
[INFO] [stdout] Superclass address: <class Base>
[INFO] [stdout] Executing byte at 6: CreateGlobal { string_index: 4 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn getClosure>", "<fn toString>", "<class Base>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: GetGlobal { string_index: 4 } Stack: []
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<class Base>", "<fn toString>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Call { arg_count: 0 } Stack: ["<class Derived>"]
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn toString>", "<class Derived>", "<fn getClosure>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: DotProperty(2) Stack: ["<Derived instance>"]
[INFO] [stdout] GC: checking value "<Derived instance>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn toString>", "<class Base>", "<Derived instance>", "<fn getClosure>", "<class Derived>", "<fn toString>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: Call { arg_count: 0 } Stack: ["<fn getClosure>"]
[INFO] [stdout] GC: checking value "<fn getClosure>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn toString>", "<fn toString>", "<Derived instance>", "<fn getClosure>", "<class Derived>", "<class Base>", "<fn getClosure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: Callable { address: 21, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn getClosure>", "<fn toString>", "<fn toString>", "<class Base>"]
[INFO] [stdout] FREEING: ["<fn toString>", "<Derived instance>", "<fn getClosure>"]
[INFO] [stdout] Executing byte at 31: GetLocal(0) Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<class Derived>", "<fn closure>", "<fn toString>", "<fn toString>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: Return Stack: ["<fn closure>", "<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Derived>", "<fn closure>", "<fn toString>", "<fn toString>", "<fn getClosure>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: CreateGlobal { string_index: 3 } Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<class Base>", "<fn toString>", "<fn closure>", "<fn toString>", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: GetGlobal { string_index: 3 } Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<class Base>", "<fn toString>", "<fn toString>", "<class Derived>", "<fn closure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: Call { arg_count: 0 } Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn closure>", "<fn toString>", "<class Base>", "<fn getClosure>", "<class Derived>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Capture(Captured(1)) Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn toString>", "<fn getClosure>", "<fn toString>", "<class Derived>", "<fn closure>", "<class Base>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: GetCaptured(0) Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn closure>", "<class Base>", "<class Derived>", "<fn toString>", "<fn getClosure>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: DotProperty(0) Stack: ["<fn closure>", "super"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "super"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn closure>", "<class Base>", "<fn toString>", "<fn toString>", "<fn getClosure>", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: Call { arg_count: 0 } Stack: ["<fn closure>", "<fn toString>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<fn toString>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<fn toString>", "<class Base>", "<fn toString>", "<class Derived>", "<fn toString>", "<fn closure>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: String(1) Stack: ["<fn closure>"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn closure>", "<fn toString>", "<fn toString>", "<class Derived>", "<class Base>", "<fn getClosure>"]
[INFO] [stdout] FREEING: ["<fn toString>"]
[INFO] [stdout] Executing byte at 18: Return Stack: ["<fn closure>", "Base"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "Base"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn closure>", "<fn toString>", "<class Base>", "Base", "<fn getClosure>", "<class Derived>", "<fn toString>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Return Stack: ["<fn closure>", "Base"]
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "Base"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn getClosure>", "<fn toString>", "<class Base>", "<fn closure>", "<fn toString>", "Base", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Print Stack: ["Base"]
[INFO] [stdout] GC: checking value "Base"
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn toString>", "<fn getClosure>", "<fn closure>", "<fn toString>", "Base", "<class Derived>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Base>"
[INFO] [stdout] GC: checking value "<class Derived>"
[INFO] [stdout] GC: checking value "<fn closure>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Base>", "<fn toString>", "<class Derived>", "<fn toString>", "<fn getClosure>", "<fn closure>"]
[INFO] [stdout] FREEING: ["Base"]
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 14,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 2,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 19,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 2, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: CreateGlobal { string_index: 2 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: Class { name_index: 3, superclass: Some(Global(2)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: CreateGlobal { string_index: 3 } Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn foo>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: Class { name_index: 5, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class B>", "<class A>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class B>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 5 } Stack: ["<class C>"]
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class C>", "<fn foo>", "<fn foo>", "<class B>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class B>", "<class C>", "<fn foo>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class C>"]
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class C>", "<fn foo>", "<class B>", "<fn foo>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: DotProperty(0) Stack: ["<C instance>"]
[INFO] [stdout] GC: checking value "<C instance>"
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<C instance>", "<class C>", "<fn foo>", "<class B>", "<fn foo>", "<fn foo>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: Call { arg_count: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class C>", "<fn foo>", "<class A>", "<C instance>", "<fn foo>", "<class B>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: String(4) Stack: []
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class C>", "<fn foo>", "<class A>", "<class B>", "<fn foo>"]
[INFO] [stdout] FREEING: ["<C instance>", "<fn foo>"]
[INFO] [stdout] Executing byte at 21: Print Stack: ["C.foo()"]
[INFO] [stdout] GC: checking value "C.foo()"
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["C.foo()", "<class B>", "<class A>", "<fn foo>", "<class C>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: GetCaptured(1) Stack: []
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class B>", "<class C>", "<fn foo>", "<class A>", "<fn foo>"]
[INFO] [stdout] FREEING: ["C.foo()"]
[INFO] [stdout] Executing byte at 23: DotProperty(0) Stack: ["super"]
[INFO] [stdout] GC: checking value "super"
[INFO] [stdout] GC: checking value "<class C>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class A>", "<class C>", "<class B>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_24_super' (41) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5e4942686a2a - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5e4942686a2a - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5e494269dada - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5e494269dada - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5e494268b502 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5e494268b502 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5e49426655ef - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5e49426655ef - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5e494267eaa9 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5e49423a508c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5e49423a508c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5e494267ec62 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5e494267ec62 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5e49426656a8 - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5e494265ce69 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5e49426663bd - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5e494269e31c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5e494269e014 - core[a96a4c5c53f902a3]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5e494269e014 - core[a96a4c5c53f902a3]::option::expect_failed
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5e494235fd30 - <core[a96a4c5c53f902a3]::option::Option<&RustLox[83b7e3fb786d5223]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5e494237effe - <RustLox[83b7e3fb786d5223]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5e49423777fd - <RustLox[83b7e3fb786d5223]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5e4942381e3a - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5e4942386b23 - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:800:49
[INFO] [stdout]   26:     0x5e494238e8aa - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5e494236eb16 - RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5e494236418a - <RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5e4942371c21 - RustLox[83b7e3fb786d5223]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5e49423719aa - RustLox[83b7e3fb786d5223]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5e49423708e6 - RustLox[83b7e3fb786d5223]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5e4942361e82 - RustLox[83b7e3fb786d5223]::test::test_module::case_24_super
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5e494235f507 - RustLox[83b7e3fb786d5223]::test::test_module::case_24_super::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5e4942364486 - <RustLox[83b7e3fb786d5223]::test::test_module::case_24_super::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5e494239916b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5e494239916b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   37:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   38:     0x5e49423a5b5b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5e49423a5b5b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   43:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   44:     0x5e49423a1274 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   45:     0x5e49423a1274 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   47:     0x5e49423a8762 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5e49423a8762 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   52:     0x5e49423a8762 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5e4942685fdf - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   54:     0x5e4942685fdf - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x794742a89aa4 - <unknown>
[INFO] [stdout]   56:     0x794742b16a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- test::test_module::case_14_inheritance stdout ----
[INFO] [stdout] [
[INFO] [stdout]     Constant(
[INFO] [stdout]         123.0,
[INFO] [stdout]     ),
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Constant(123.0) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["123"]
[INFO] [stdout] GC: checking value "123"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "123"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: None,
[INFO] [stderr] error: test failed, to rerun pass `--bin RustLox`
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 36,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 45,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 7,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 4,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 56,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 67,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 7,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         8,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         9,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         3,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         10,
[INFO] [stdout]     ),
[INFO] [stdout]     String(
[INFO] [stdout]         11,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         6,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 5,
[INFO] [stdout]         arg_count: 2,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 2,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 4, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 4 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 7, superclass: Some(Global(4)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 7 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 7 } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<class Foo>", "<class Bar>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "<fn barPrint>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 5 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<Bar instance>", "<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<class Foo>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: String(8) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: String(9) Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "foo 1", "<class Bar>", "<fn fooPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: GetGlobal { string_index: 5 } Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "foo 1", "<fn barPrint>", "<fn bar>", "<Bar instance>", "<fn foo>", "<class Foo>", "foo 2", "<fn bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: DotProperty(3) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<class Foo>", "foo 1", "<Bar instance>", "<class Bar>", "<fn foo>", "<fn barPrint>", "<fn foo>", "foo 2", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: Call { arg_count: 2 } Stack: ["foo 1", "foo 2", "<fn foo>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 1", "foo 2", "<fn foo>", "<fn fooPrint>", "<fn foo>", "<class Bar>", "<class Foo>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn barPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 46: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 1", "<fn barPrint>", "foo 2", "<class Bar>", "<fn bar>", "<fn bar>", "<class Foo>", "<fn fooPrint>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 47: GetLocal(0) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<fn barPrint>", "foo 1", "<fn fooPrint>", "<fn foo>", "<Bar instance>", "foo 2", "<class Foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 48: AssignField { field_name_index: 1 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn barPrint>", "<fn foo>", "foo 1", "<fn foo>", "<Bar instance>", "<class Bar>", "<fn fooPrint>", "foo 2", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 49: Pop(1) Stack: ["foo 1", "foo 2", "foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 2", "<class Bar>", "<fn bar>", "<Bar instance>", "<fn bar>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "foo 1", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 50: GetCaptured(0) Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<Bar instance>", "<fn fooPrint>", "<fn barPrint>", "foo 1", "<fn foo>", "foo 2", "<class Bar>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<class Foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 51: GetLocal(1) Stack: ["foo 1", "foo 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "foo 2", "<fn barPrint>", "foo 1", "<fn barPrint>", "<Bar instance>", "<fn foo>", "<fn foo>", "<class Foo>", "<fn bar>", "<class Bar>", "<fn bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 52: AssignField { field_name_index: 2 } Stack: ["foo 1", "foo 2", "<Bar instance>", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Foo>", "<Bar instance>", "<fn bar>", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<fn foo>", "<fn barPrint>", "<fn bar>", "foo 1", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 53: Pop(1) Stack: ["foo 1", "foo 2", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<fn bar>", "foo 2", "<class Foo>", "foo 1", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 54: Nil Stack: ["foo 1", "foo 2"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "foo 1", "<class Bar>", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "<class Foo>", "<fn bar>", "<fn fooPrint>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 55: Return Stack: ["foo 1", "foo 2", "nil"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn foo>", "foo 1", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "foo 2", "<Bar instance>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>", "<fn bar>", "<class Foo>", "foo 2", "foo 1", "<fn fooPrint>", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn foo>", "<Bar instance>", "<fn barPrint>", "<class Bar>", "<fn bar>", "<class Foo>", "foo 2", "<fn fooPrint>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "foo 1", "<fn bar>", "<class Foo>", "<fn barPrint>", "foo 2", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn barPrint>", "<class Foo>", "<Bar instance>", "foo 1", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "foo 2", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["foo 1", "<fn barPrint>", "<fn fooPrint>", "<Bar instance>", "<class Bar>", "<fn bar>", "<fn foo>", "<fn barPrint>", "foo 2", "<fn foo>", "<class Foo>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn foo>", "<fn bar>", "foo 1", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<fn barPrint>", "<class Foo>", "<fn foo>", "<class Bar>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["foo 1"]
[INFO] [stdout] GC: checking value "foo 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<class Bar>", "<fn foo>", "<fn bar>", "foo 1", "<class Foo>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn fooPrint>", "foo 2", "<fn fooPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "foo 2", "<Bar instance>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>", "<class Foo>", "foo 1", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<fn foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "<fn foo>", "foo 1", "<fn bar>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "foo 2", "<class Bar>", "<class Foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["foo 2"]
[INFO] [stdout] GC: checking value "foo 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "<fn fooPrint>", "foo 1", "<fn foo>", "<Bar instance>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<fn bar>", "<fn fooPrint>", "<class Foo>", "foo 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<class Foo>", "<fn barPrint>", "<fn foo>", "<Bar instance>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "foo 2", "foo 1", "<fn fooPrint>", "<fn bar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<Bar instance>", "<class Foo>", "<class Bar>", "<fn barPrint>", "foo 1", "<fn bar>", "<fn bar>", "foo 2", "<fn barPrint>", "<fn foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Foo>", "<fn bar>", "<Bar instance>", "foo 2", "<fn foo>", "<class Bar>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "foo 1", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: String(10) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn bar>", "<fn foo>", "<fn bar>", "<fn barPrint>", "<class Foo>", "foo 2", "foo 1", "<fn barPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: String(11) Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn barPrint>", "<fn fooPrint>", "foo 1", "foo 2", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn foo>", "<fn foo>", "bar 1", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: GetGlobal { string_index: 5 } Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "foo 2", "bar 1", "<fn foo>", "foo 1", "<fn foo>", "<fn bar>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "<Bar instance>", "bar 2", "<class Bar>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 24: DotProperty(5) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "bar 1", "bar 2", "<Bar instance>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "<class Foo>", "<fn barPrint>", "foo 2", "<fn foo>", "<fn barPrint>", "<fn bar>", "<class Bar>", "foo 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: Call { arg_count: 2 } Stack: ["bar 1", "bar 2", "<fn bar>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<fn bar>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn foo>", "foo 1", "<Bar instance>", "<fn fooPrint>", "<fn fooPrint>", "bar 1", "<fn bar>", "<fn foo>", "foo 2", "<fn barPrint>", "bar 2", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 57: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "<Bar instance>", "foo 1", "<fn bar>", "<fn barPrint>", "<fn barPrint>", "<class Bar>", "<fn fooPrint>", "<class Foo>", "bar 1", "bar 2", "foo 2", "<fn foo>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 58: GetLocal(0) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn fooPrint>", "bar 1", "foo 2", "bar 2", "foo 1", "<fn bar>", "<fn foo>", "<class Foo>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<Bar instance>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 59: AssignField { field_name_index: 1 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>", "bar 2", "<fn foo>", "<fn foo>", "bar 1", "<class Foo>", "foo 2", "<fn barPrint>", "foo 1", "<fn bar>", "<fn fooPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 60: Pop(1) Stack: ["bar 1", "bar 2", "bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn barPrint>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "bar 2", "bar 1", "<class Bar>", "<class Foo>", "foo 2", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<Bar instance>"]
[INFO] [stdout] FREEING: ["foo 1"]
[INFO] [stdout] Executing byte at 61: GetCaptured(0) Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn bar>", "<class Foo>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>", "foo 2", "<fn foo>", "<Bar instance>", "<fn fooPrint>", "<fn foo>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 62: GetLocal(1) Stack: ["bar 1", "bar 2", "<Bar instance>"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<class Bar>", "<fn barPrint>", "<fn foo>", "<class Foo>", "foo 2", "<fn bar>", "bar 1", "<fn fooPrint>", "<Bar instance>", "bar 2", "<fn barPrint>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 63: AssignField { field_name_index: 2 } Stack: ["bar 1", "bar 2", "<Bar instance>", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<class Bar>", "bar 1", "<fn fooPrint>", "bar 2", "foo 2", "<fn fooPrint>", "<fn bar>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 64: Pop(1) Stack: ["bar 1", "bar 2", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "bar 2", "<Bar instance>", "<fn bar>", "<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<class Foo>", "bar 1"]
[INFO] [stdout] FREEING: ["foo 2"]
[INFO] [stdout] Executing byte at 65: Nil Stack: ["bar 1", "bar 2"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<class Bar>", "<fn foo>", "<class Foo>", "<fn bar>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "bar 1", "<fn barPrint>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 66: Return Stack: ["bar 1", "bar 2", "nil"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<fn fooPrint>", "<fn barPrint>", "<class Bar>", "bar 1", "<class Foo>", "<fn fooPrint>", "<fn foo>", "<Bar instance>", "<fn barPrint>", "bar 2", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<class Foo>", "<fn bar>", "<fn bar>", "<fn foo>", "bar 2", "<class Bar>", "<fn barPrint>", "<fn barPrint>", "<fn fooPrint>", "<fn fooPrint>", "<fn foo>", "bar 1"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "<fn bar>", "<fn barPrint>", "bar 1", "<fn foo>", "bar 2", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 28: DotProperty(6) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn bar>", "<fn foo>", "<class Bar>", "bar 2", "<class Foo>", "<fn barPrint>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "bar 1", "<fn barPrint>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 29: Call { arg_count: 0 } Stack: ["<fn barPrint>"]
[INFO] [stdout] GC: checking value "<fn barPrint>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "bar 2", "<fn bar>", "<fn fooPrint>", "bar 1", "<class Bar>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<fn barPrint>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 68: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<class Bar>", "<fn bar>", "bar 2", "<fn bar>", "<class Foo>", "bar 1", "<fn barPrint>", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 69: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn barPrint>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<fn foo>", "bar 1", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "<class Bar>", "<fn bar>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 70: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 1", "<fn barPrint>", "<class Foo>", "<fn barPrint>", "<fn fooPrint>", "<fn foo>", "bar 2", "<fn bar>", "<class Bar>", "<Bar instance>", "<fn foo>", "<fn fooPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 71: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<fn fooPrint>", "bar 2", "bar 1", "<fn bar>", "<fn barPrint>", "<fn barPrint>", "<class Foo>", "<fn foo>", "<class Bar>", "<Bar instance>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 72: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn foo>", "<fn fooPrint>", "<class Bar>", "<fn barPrint>", "bar 2", "<fn fooPrint>", "<Bar instance>", "<fn foo>", "bar 1", "<fn barPrint>", "<class Foo>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 73: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn foo>", "bar 1", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<class Foo>", "<fn fooPrint>", "bar 2", "<class Bar>", "<fn bar>", "<fn barPrint>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 74: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "bar 1", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn barPrint>", "<class Foo>", "<class Bar>", "<fn barPrint>", "bar 2", "<fn bar>", "<Bar instance>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 75: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn foo>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "<fn bar>", "<class Foo>", "<fn barPrint>", "bar 1", "bar 2", "<fn bar>", "<Bar instance>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 30: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<class Bar>", "<fn fooPrint>", "<Bar instance>", "<fn bar>", "<class Foo>", "<fn barPrint>", "<fn bar>", "bar 1", "<fn foo>", "<fn fooPrint>", "<fn foo>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 31: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn bar>", "bar 2", "<fn foo>", "<fn barPrint>", "bar 1", "<fn fooPrint>", "<class Bar>", "<fn fooPrint>", "<Bar instance>", "<class Foo>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 32: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 1", "<Bar instance>", "<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "<fn bar>", "bar 2", "<fn barPrint>", "<class Foo>", "<fn foo>", "<fn foo>", "<fn barPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 33: Call { arg_count: 0 } Stack: ["<fn fooPrint>"]
[INFO] [stdout] GC: checking value "<fn fooPrint>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 1", "<fn foo>", "<fn bar>", "<class Bar>", "<fn bar>", "<fn foo>", "<class Foo>", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "bar 2", "<Bar instance>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn fooPrint>", "<fn barPrint>", "<fn barPrint>", "<fn foo>", "bar 1", "<class Bar>", "<class Foo>", "<fn fooPrint>", "<fn bar>", "<Bar instance>", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 38: DotProperty(1) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "<fn barPrint>", "bar 1", "<class Foo>", "<fn barPrint>", "<class Bar>", "<Bar instance>", "<fn foo>", "bar 2", "<fn bar>", "<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 39: Print Stack: ["bar 1"]
[INFO] [stdout] GC: checking value "bar 1"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "<fn bar>", "<fn fooPrint>", "<fn fooPrint>", "<class Foo>", "bar 1", "<fn bar>", "<fn foo>", "bar 2", "<fn barPrint>", "<Bar instance>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: GetCaptured(0) Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn bar>", "bar 1", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<class Foo>", "bar 2", "<fn barPrint>", "<fn foo>", "<Bar instance>", "<fn barPrint>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn barPrint>", "<class Foo>", "<fn barPrint>", "bar 1", "<Bar instance>", "<fn bar>", "bar 2", "<fn bar>", "<fn fooPrint>", "<fn foo>", "<fn foo>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Print Stack: ["bar 2"]
[INFO] [stdout] GC: checking value "bar 2"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn bar>", "bar 2", "<fn barPrint>", "<Bar instance>", "<fn fooPrint>", "<class Bar>", "<fn fooPrint>", "<fn foo>", "bar 1", "<fn foo>", "<fn barPrint>", "<fn bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 43: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn foo>", "<fn bar>", "<fn barPrint>", "<Bar instance>", "<fn foo>", "<class Foo>", "<fn fooPrint>", "<fn bar>", "bar 1", "bar 2", "<fn barPrint>", "<class Bar>", "<fn fooPrint>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 44: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn fooPrint>", "<fn foo>", "<fn barPrint>", "<fn bar>", "<fn fooPrint>", "<class Bar>", "bar 1", "<fn barPrint>", "<fn bar>", "<class Foo>", "bar 2", "<fn foo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 34: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["<fn barPrint>", "<fn foo>", "<Bar instance>", "<class Foo>", "<fn fooPrint>", "<fn fooPrint>", "<class Bar>", "<fn bar>", "bar 1", "<fn foo>", "<fn barPrint>", "<fn bar>", "bar 2"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: reachable: ["bar 2", "<fn foo>", "<fn foo>", "<fn fooPrint>", "<fn bar>", "<fn fooPrint>", "<Bar instance>", "<fn barPrint>", "bar 1", "<class Foo>", "<fn bar>", "<fn barPrint>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 5,
[INFO] [stdout]         type_: Function,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 1,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 0,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Callable { address: 5, type_: Function } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 1: CreateGlobal { string_index: 0 } Stack: ["<fn foo>"]
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 2: Class { name_index: 1, superclass: Some(Global(0)) } Stack: []
[INFO] [stdout] GC: checking value "<fn foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 24,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 29,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 6,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 34,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 39,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         4,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 5,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         2,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class Foo>"]
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn methodOnFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 6, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<class Foo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Superclass address: <class Foo>
[INFO] [stdout] Executing byte at 7: CreateGlobal { string_index: 6 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<class Foo>", "<fn override>", "<fn methodOnBar>", "<class Bar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: GetGlobal { string_index: 6 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<class Foo>", "<class Bar>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 9: Call { arg_count: 0 } Stack: ["<class Bar>"]
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn override>", "<class Foo>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 10: CreateGlobal { string_index: 5 } Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn override>", "<class Foo>", "<fn methodOnFoo>", "<fn override>", "<Bar instance>", "<class Bar>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 11: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<class Foo>", "<class Bar>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 12: DotProperty(0) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<class Bar>", "<fn override>", "<fn methodOnFoo>", "<Bar instance>", "<fn methodOnFoo>", "<class Foo>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 13: Call { arg_count: 0 } Stack: ["<fn methodOnFoo>"]
[INFO] [stdout] GC: checking value "<fn methodOnFoo>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn methodOnFoo>", "<fn methodOnBar>", "<class Foo>", "<fn methodOnFoo>", "<fn override>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<class Bar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 25: String(1) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<class Bar>", "<Bar instance>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 26: Print Stack: ["foo"]
[INFO] [stdout] GC: checking value "foo"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["foo", "<fn methodOnBar>", "<class Foo>", "<fn methodOnFoo>", "<Bar instance>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<class Bar>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 27: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn override>", "<Bar instance>", "<fn override>", "<fn methodOnBar>", "<class Bar>", "<fn methodOnFoo>", "<fn methodOnBar>", "<class Foo>", "<fn override>"]
[INFO] [stdout] FREEING: ["foo"]
[INFO] [stdout] Executing byte at 28: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn methodOnFoo>", "<fn override>", "<Bar instance>", "<fn override>", "<fn override>", "<class Bar>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 14: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn override>", "<class Bar>", "<class Foo>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 15: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<class Foo>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 16: DotProperty(4) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<Bar instance>", "<class Bar>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn methodOnFoo>", "<class Foo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 17: Call { arg_count: 0 } Stack: ["<fn methodOnBar>"]
[INFO] [stdout] GC: checking value "<fn methodOnBar>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn methodOnBar>", "<class Foo>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<Bar instance>", "<class Bar>", "<fn override>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 35: String(5) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<class Foo>", "<fn methodOnBar>", "<fn override>", "<Bar instance>", "<fn methodOnFoo>", "<fn override>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 36: Print Stack: ["bar"]
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<fn override>", "<class Bar>", "<class Foo>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>", "<fn methodOnBar>", "bar", "<Bar instance>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 37: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn methodOnFoo>", "<fn methodOnBar>", "<fn override>", "<class Bar>", "<fn override>", "<fn methodOnBar>", "<Bar instance>", "<fn methodOnFoo>", "<class Foo>"]
[INFO] [stdout] FREEING: ["bar"]
[INFO] [stdout] Executing byte at 38: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<class Foo>", "<Bar instance>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 18: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn override>", "<fn override>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnBar>", "<Bar instance>", "<class Foo>", "<fn methodOnBar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 19: GetGlobal { string_index: 5 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<fn methodOnFoo>", "<Bar instance>", "<class Foo>", "<fn override>", "<class Bar>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 20: DotProperty(2) Stack: ["<Bar instance>"]
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<Bar instance>", "<fn methodOnBar>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn methodOnFoo>", "<fn override>", "<class Foo>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 21: Call { arg_count: 0 } Stack: ["<fn override>"]
[INFO] [stdout] GC: checking value "<fn override>"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<Bar instance>", "<fn methodOnBar>", "<fn override>", "<fn override>", "<fn override>", "<fn methodOnBar>", "<class Foo>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 40: String(5) Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<Bar instance>", "<fn methodOnFoo>", "<fn override>", "<fn methodOnFoo>", "<class Foo>", "<class Bar>", "<fn override>", "<fn override>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 41: Print Stack: ["bar"]
[INFO] [stdout] GC: checking value "bar"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["bar", "<fn methodOnFoo>", "<class Foo>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<Bar instance>", "<fn override>", "<fn methodOnBar>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 42: Nil Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn override>", "<fn override>", "<fn override>", "<Bar instance>", "<fn methodOnBar>", "<fn methodOnBar>", "<fn methodOnFoo>", "<class Foo>", "<class Bar>", "<fn methodOnFoo>"]
[INFO] [stdout] FREEING: ["bar"]
[INFO] [stdout] Executing byte at 43: Return Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnFoo>", "<class Bar>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<Bar instance>", "<fn methodOnBar>", "<fn override>", "<class Foo>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: Pop(1) Stack: ["nil"]
[INFO] [stdout] GC: checking value "nil"
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<fn methodOnBar>", "<class Foo>", "<fn methodOnBar>", "<fn override>", "<fn methodOnFoo>", "<class Bar>", "<fn methodOnFoo>", "<Bar instance>", "<fn override>", "<fn override>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 23: Exit { code: 0 } Stack: []
[INFO] [stdout] GC: checking value "<class Foo>"
[INFO] [stdout] GC: checking value "<class Bar>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<Bar instance>"
[INFO] [stdout] GC: reachable: ["<class Bar>", "<fn override>", "<fn methodOnBar>", "<class Foo>", "<fn override>", "<fn methodOnFoo>", "<fn methodOnFoo>", "<fn override>", "<Bar instance>", "<fn methodOnBar>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] [
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 3,
[INFO] [stdout]         superclass: None,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 15,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     Callable {
[INFO] [stdout]         address: 21,
[INFO] [stdout]         type_: Method,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 3,
[INFO] [stdout]     },
[INFO] [stdout]     Class {
[INFO] [stdout]         name_index: 4,
[INFO] [stdout]         superclass: Some(
[INFO] [stdout]             Global(
[INFO] [stdout]                 3,
[INFO] [stdout]             ),
[INFO] [stdout]         ),
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     String(
[INFO] [stdout]         5,
[INFO] [stdout]     ),
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 4,
[INFO] [stdout]     },
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     CreateGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     GetGlobal {
[INFO] [stdout]         string_index: 6,
[INFO] [stdout]     },
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Call {
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Exit {
[INFO] [stdout]         code: 0,
[INFO] [stdout]     },
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 0,
[INFO] [stdout]         arg_count: 0,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     DotProperty(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     Print,
[INFO] [stdout]     Nil,
[INFO] [stdout]     Return,
[INFO] [stdout]     CallableInfo {
[INFO] [stdout]         name_index: 2,
[INFO] [stdout]         arg_count: 1,
[INFO] [stdout]     },
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     GetLocal(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     AssignField {
[INFO] [stdout]         field_name_index: 1,
[INFO] [stdout]     },
[INFO] [stdout]     Pop(
[INFO] [stdout]         1,
[INFO] [stdout]     ),
[INFO] [stdout]     GetCaptured(
[INFO] [stdout]         0,
[INFO] [stdout]     ),
[INFO] [stdout]     Return,
[INFO] [stdout] ]
[INFO] [stdout] Executing byte at 0: Class { name_index: 3, superclass: None } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: []
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 3: CreateGlobal { string_index: 3 } Stack: ["<class A>"]
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: reachable: ["<class A>", "<fn init>", "<fn test>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 4: Class { name_index: 4, superclass: Some(Global(3)) } Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<class A>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 5: CreateGlobal { string_index: 4 } Stack: ["<class B>"]
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<class B>", "<fn init>", "<fn test>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 6: String(5) Stack: []
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn test>", "<class A>", "<fn init>", "<class B>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 7: GetGlobal { string_index: 4 } Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["value", "<fn test>", "<class A>", "<class B>", "<fn init>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 8: Call { arg_count: 1 } Stack: ["value", "<class B>"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["value", "<class B>", "<fn test>", "<fn init>", "<class A>"]
[INFO] [stdout] FREEING: []
[INFO] [stdout] Executing byte at 22: GetCaptured(0) Stack: ["value"]
[INFO] [stdout] GC: checking value "value"
[INFO] [stdout] GC: checking value "<native fn clock>"
[INFO] [stdout] GC: checking value "<class B>"
[INFO] [stdout] GC: checking value "<class A>"
[INFO] [stdout] GC: reachable: ["<fn init>", "<class A>", "<fn test>", "value", "<class B>"]
[INFO] [stdout] FREEING: ["<fn test>", "<fn init>", "<B instance>"]
[INFO] [stdout] 
[INFO] [stdout] thread 'test::test_module::case_14_inheritance' (31) panicked at src/heap.rs:121:14:
[INFO] [stdout] Use after free: invalid address
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5e4942686a2a - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5e4942686a2a - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5e4942686a2a - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5e494269dada - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5e494269dada - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5e494268b502 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5e494268b502 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5e49426655ef - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5e49426655ef - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5e494267eaa9 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5e49423a508c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5e49423a508c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5e494267ec62 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5e494267ec62 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5e49426656a8 - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5e494265ce69 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5e49426663bd - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5e494269e31c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5e494269e014 - core[a96a4c5c53f902a3]::panicking::panic_display::<&str>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:259:5
[INFO] [stdout]   20:     0x5e494269e014 - core[a96a4c5c53f902a3]::option::expect_failed
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:2260:5
[INFO] [stdout]   21:     0x5e494235fd30 - <core[a96a4c5c53f902a3]::option::Option<&RustLox[83b7e3fb786d5223]::heap::HeapValue>>::expect
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/option.rs:971:21
[INFO] [stdout]   22:     0x5e494237effe - <RustLox[83b7e3fb786d5223]::heap::Heap>::get
[INFO] [stdout]                                at /opt/rustwide/workdir/src/heap.rs:121:14
[INFO] [stdout]   23:     0x5e49423777fd - <RustLox[83b7e3fb786d5223]::value::Value>::deref
[INFO] [stdout]                                at /opt/rustwide/workdir/src/value.rs:103:51
[INFO] [stdout]   24:     0x5e4942381e3a - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::get_captured
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:188:42
[INFO] [stdout]   25:     0x5e4942384442 - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret_unsafe
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:664:38
[INFO] [stdout]   26:     0x5e494238e8aa - <RustLox[83b7e3fb786d5223]::interpreter::Interpreter<&mut alloc[c960326de6de28e6]::string::String, &mut alloc[c960326de6de28e6]::string::String>>::interpret
[INFO] [stdout]                                at /opt/rustwide/workdir/src/interpreter.rs:866:20
[INFO] [stdout]   27:     0x5e494236eb16 - RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:100:29
[INFO] [stdout]   28:     0x5e494236418a - <RustLox[83b7e3fb786d5223]::test::test_interpret_file::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<(&str,)>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   29:     0x5e4942371c21 - RustLox[83b7e3fb786d5223]::test::test_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:38:18
[INFO] [stdout]   30:     0x5e49423719aa - RustLox[83b7e3fb786d5223]::test::test_interpret_file
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:92:5
[INFO] [stdout]   31:     0x5e49423708e6 - RustLox[83b7e3fb786d5223]::test::test_module
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:160:9
[INFO] [stdout]   32:     0x5e4942362242 - RustLox[83b7e3fb786d5223]::test::test_module::case_14_inheritance
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:1
[INFO] [stdout]   33:     0x5e494235f7d7 - RustLox[83b7e3fb786d5223]::test::test_module::case_14_inheritance::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/test.rs:125:10
[INFO] [stdout]   34:     0x5e4942364846 - <RustLox[83b7e3fb786d5223]::test::test_module::case_14_inheritance::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x5e494239916b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   36:     0x5e494239916b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   37:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   38:     0x5e49423a5b5b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   39:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   40:     0x5e49423a5b5b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   41:     0x5e49423a5b5b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   42:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   43:     0x5e49423a5b5b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   44:     0x5e49423a1274 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   45:     0x5e49423a1274 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   46:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   47:     0x5e49423a8762 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x5e49423a8762 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x5e49423a8762 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x5e49423a8762 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   52:     0x5e49423a8762 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   53:     0x5e4942685fdf - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   54:     0x5e4942685fdf - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   55:     0x794742a89aa4 - <unknown>
[INFO] [stdout]   56:     0x794742b16a64 - clone
[INFO] [stdout]   57:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test::test_module::case_05_class
[INFO] [stdout]     test::test_module::case_08_constructor
[INFO] [stdout]     test::test_module::case_14_inheritance
[INFO] [stdout]     test::test_module::case_24_super
[INFO] [stdout]     test::test_module::case_25_this
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 26 passed; 5 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.87s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "45981dfca83783bf80a5e8a27bd6fa89737477c76b75d5d94750aaf0924533d4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "45981dfca83783bf80a5e8a27bd6fa89737477c76b75d5d94750aaf0924533d4", kill_on_drop: false }`
[INFO] [stdout] 45981dfca83783bf80a5e8a27bd6fa89737477c76b75d5d94750aaf0924533d4
